[INFO] cloning repository https://github.com/dspearson/oggmux
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/dspearson/oggmux" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdspearson%2Foggmux", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdspearson%2Foggmux'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1fff03a5f8b8f41d38ea4eb57dbcb5edd5cd3f34
[INFO] testing dspearson/oggmux against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdspearson%2Foggmux" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/dspearson/oggmux
[INFO] finished tweaking git repo https://github.com/dspearson/oggmux
[INFO] tweaked toml for git repo https://github.com/dspearson/oggmux written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/dspearson/oggmux on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/dspearson/oggmux already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ab642929367cda656747622177bf02dfb1ab3a649087d64b016d7846e06b8095
[INFO] running `Command { std: "docker" "start" "-a" "ab642929367cda656747622177bf02dfb1ab3a649087d64b016d7846e06b8095", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ab642929367cda656747622177bf02dfb1ab3a649087d64b016d7846e06b8095", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ab642929367cda656747622177bf02dfb1ab3a649087d64b016d7846e06b8095", kill_on_drop: false }`
[INFO] [stdout] ab642929367cda656747622177bf02dfb1ab3a649087d64b016d7846e06b8095
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a054015941b08f630fa77fdc5fe83c42cd4c3417b976b6fd13a1bd4703706ab1
[INFO] running `Command { std: "docker" "start" "-a" "a054015941b08f630fa77fdc5fe83c42cd4c3417b976b6fd13a1bd4703706ab1", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.177
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling quote v1.0.41
[INFO] [stderr]    Compiling unicode-ident v1.0.19
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling cfg-if v1.0.3
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling anyhow v1.0.100
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling log v0.4.28
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling ogg v0.9.2
[INFO] [stderr]    Compiling ogg_pager v0.7.0
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling socket2 v0.6.0
[INFO] [stderr]    Compiling mio v1.0.4
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling oggmux v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.60s
[INFO] running `Command { std: "docker" "inspect" "a054015941b08f630fa77fdc5fe83c42cd4c3417b976b6fd13a1bd4703706ab1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a054015941b08f630fa77fdc5fe83c42cd4c3417b976b6fd13a1bd4703706ab1", kill_on_drop: false }`
[INFO] [stdout] a054015941b08f630fa77fdc5fe83c42cd4c3417b976b6fd13a1bd4703706ab1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3843b99202455f05d0990e7253820d78155e2c59ed8d63c3bf58308e538aace8
[INFO] running `Command { std: "docker" "start" "-a" "3843b99202455f05d0990e7253820d78155e2c59ed8d63c3bf58308e538aace8", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling log v0.4.28
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling jiff v0.2.23
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling oggmux v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling env_filter v1.0.0
[INFO] [stderr]    Compiling env_logger v0.11.9
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 13.66s
[INFO] running `Command { std: "docker" "inspect" "3843b99202455f05d0990e7253820d78155e2c59ed8d63c3bf58308e538aace8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3843b99202455f05d0990e7253820d78155e2c59ed8d63c3bf58308e538aace8", kill_on_drop: false }`
[INFO] [stdout] 3843b99202455f05d0990e7253820d78155e2c59ed8d63c3bf58308e538aace8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d1e5bb382ef683651ad301b1b55a4feef68326aba785127b1cb94e6a6149de03
[INFO] running `Command { std: "docker" "start" "-a" "d1e5bb382ef683651ad301b1b55a4feef68326aba785127b1cb94e6a6149de03", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/oggmux-fc524ddcfe18762c)
[INFO] [stdout] 
[INFO] [stdout] running 26 tests
[INFO] [stdout] test comments::tests::test_crc_matches_ogg_pager ... ok
[INFO] [stdout] test comments::tests::test_generate_comment_packet_empty ... ok
[INFO] [stdout] test comments::tests::test_create_comment_page ... ok
[INFO] [stdout] test metrics::tests::test_metric_stats_updates ... ok
[INFO] [stdout] test metrics::tests::test_metrics_collector ... ok
[INFO] [stdout] test comments::tests::test_generate_comment_packet_with_unicode ... ok
[INFO] [stdout] test comments::tests::test_create_comment_page_large_packet ... ok
[INFO] [stdout] test metrics::tests::test_silence_percentage ... ok
[INFO] [stdout] test comments::tests::test_crc_known_value ... ok
[INFO] [stdout] test mux::tests::test_vorbis_config_silence_key_vbr ... ok
[INFO] [stdout] test mux::tests::test_mux_graceful_shutdown ... ok
[INFO] [stdout] test stream::tests::test_stream_processor_initial_state ... ok
[INFO] [stdout] test mux::tests::test_mux_shutdown_behavior ... ok
[INFO] [stdout] test silence::tests::test_silence_template_creation ... ok
[INFO] [stdout] test stream::tests::test_stream_processor_finalise_empty ... ok
[INFO] [stdout] test utils::tests::test_calculate_buffer_size ... ok
[INFO] [stdout] test stream::tests::test_vorbis_bos_detection ... ok
[INFO] [stdout] test utils::tests::test_calculate_buffered_seconds ... ok
[INFO] [stdout] test utils::tests::test_round_to_power_of_2 ... ok
[INFO] [stdout] test mux::tests::test_vorbis_config_silence_key_cbr ... ok
[INFO] [stdout] test comments::tests::test_create_comment_page_crc_changes ... ok
[INFO] [stdout] test comments::tests::test_generate_comment_packet ... ok
[INFO] [stdout] test metrics::tests::test_uptime_and_idle ... ok
[INFO] [stdout] test timing::tests::test_clock_elapsed ... ok
[INFO] [stdout] test timing::tests::test_granule_conversion_accuracy ... ok
[INFO] [stdout] test timing::tests::test_lead_secs_positive_negative ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-921a57b90ad13f4b)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test test_metadata_callback_returning_none ... ok
[INFO] [stdout] test test_mux_with_valid_ogg_data ... ok
[INFO] [stdout] test test_shutdown_during_active_stream ... ok
[INFO] [stdout] test test_metadata_callback_invoked ... ok
[INFO] [stdout] test test_truncated_ogg_data ... ok
[INFO] [stdout] test test_metadata_callback_with_granule_position ... ok
[INFO] [stdout] test test_silence_generation ... ok
[INFO] [stdout] test test_gapless_mode_no_silence ... ok
[INFO] [stdout] test test_with_silence_mode_inserts_silence ... ok
[INFO] [stdout] test test_metadata_injection_creates_pages ... ok
[INFO] [stdout] test test_output_pages_have_consistent_serial_within_stream ... ok
[INFO] [stdout] test test_invalid_data_handling ... ok
[INFO] [stdout] test test_all_output_pages_have_valid_crcs ... ok
[INFO] [stdout] test test_silence_then_real_stream_transition ... ok
[INFO] [stdout] test test_error_resilience_multiple_bad_chunks ... ok
[INFO] [stdout] test test_gapless_with_multiple_streams ... ok
[INFO] [stdout] test test_multiple_data_pushes ... ok
[INFO] [stdout] test test_sequential_stream_handoff ... ok
[INFO] [stdout] test test_error_resilience_bad_then_good_data ... ok
[INFO] [stdout] test test_granule_positions_are_monotonic ... ok
[INFO] [stdout] test test_metadata_injected_with_next_stream_serial ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.59s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests oggmux
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test src/mux.rs - mux::OggMux::spawn (line 578) - compile ... ok
[INFO] [stdout] test src/utils.rs - utils::calculate_buffer_size (line 19) ... ok
[INFO] [stdout] test src/mux.rs - mux::OggMux::with_metadata_callback (line 445) ... ok
[INFO] [stdout] test src/lib.rs - (line 11) - compile ... ok
[INFO] [stdout] test src/utils.rs - utils::calculate_buffered_seconds (line 56) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.84s; merged doctests compilation took 0.82s
[INFO] running `Command { std: "docker" "inspect" "d1e5bb382ef683651ad301b1b55a4feef68326aba785127b1cb94e6a6149de03", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d1e5bb382ef683651ad301b1b55a4feef68326aba785127b1cb94e6a6149de03", kill_on_drop: false }`
[INFO] [stdout] d1e5bb382ef683651ad301b1b55a4feef68326aba785127b1cb94e6a6149de03
